0001411: The STB can't boot when the user remove the power cable during firmware upgrade.

ID	Project	Category	View Status	Date Submitted	Last Update

0001411	K18X001.00 SKB SWAN	SW	public	2021-12-16 14:31	2022-01-12 10:34

Reporter	(ALTech) Sangmin Choi	Assigned To	(ALTech) Sangmin Choi	Due Date	2021-12-30 13:00
Priority	urgent	Severity	s6-feature	Reproducibility	always
Status	closed	Resolution	fixed

Summary	0001411: The STB can't boot when the user remove the power cable during firmware upgrade.
Description	The STB can't boot when the user remove the power cable during firmware upgrade. You can reproduce easily and you can see the following console message. init cpupll 800MHz init mempll 2400MHz init 3GB DDR, lib version r63074 .............. .............. [ 0] Start kernel at 0x05880000 [ 1948]I(SM): *****IR ready**** [ 0.813375] ioremap success: vir_addr: (ptrval), size: 0x3000000, phy_addr: (ptrval)! [ 0.823601] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered [ 0.886663] chipidea-usb2 f7ed0000.usb: set of GPIOH_5(496) as USB Hub reset [ 0.893958] chipidea-usb2 f7ed0000.usb: ok to acquire gpio and set output [ 0.902439] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered [ 0.919426] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered [ 1.016260] device-mapper: verity: 253:2: metadata block 210229 is corrupted [ 1.075659] reboot: Restarting system with command 'dm-verity device corrupted' [ 2280]I(SM): %%%%SM:Msg reset system%%%% [ 2284]I(SM): ****enter flow ---Sysreset to Active--- [ 2290]I(SM): soc_assert done [ 2293]I(SM): peripheral_assert done [ 2301]I(SM): mv_sm_power_socpoweroff done [ 2609]I(SM): mv_sm_power_socpoweron done [ 2665]I(SM): peripheral_assert done [ 2669]I(SM): soc_assert done [ 2765]I(SM): peripheral_deassert done [ 2768]I(SM): soc_deassert done
Steps To Reproduce	1. Prepare the STB as 19.531.18 user build and check the STB boot up successfully 2. Copy the 19.531.20 upgrade image to the USB stick 3. Connect the USB stick and power off the STB 4. Keep pressing "Volume down" and "Channel down" at the front panel and power on the STB 5. STB start the USB upgrade 6. First progress screen is for copying upgrade image from USB Stick to STB storage 7. Second progress screen is updating EMMC partition 8. Remove the power cable when the second progress is 34% 9. Power on the STB and the STB will reboot repeatedly
Tags	No tags attached.
Attach Tags	(Separate by ",")

User List	(ALTech) Sangmin Choi , (SW) Bcan Yeh Username

(ALTech) Sangmin Choi 2021-12-16 14:35 developer ~0008932	Dear Jacky, The STB reboot repeatedly due to "dm-verity device corrupted". Please modify the STB go to recovery mode when the STB try to reboot due to "dm-verity device corrupted". The cache partition is remained so the STB is going to upgrade process again. Could you please check it?

(SW) Jacky Chiang 2021-12-20 09:13 manager ~0008951	Hi Sangmin, We will try to reproduce this issue first, so do you mean it can't enter recovery mode if you do keep pressing "Volume down" and "Channel down" at the front panel and power on the STB when this issue happened ?

(ALTech) Sangmin Choi 2021-12-20 09:58 developer ~0008955	Hi, Jacky, No, you can enter the recovery mode using hidden key after this issue was happened. But, the user can't use this way and they never want their STB keep unusable any moment. So, SKB want to repeat upgrade process when this issue happened.

(ALTech) Wooshin Kang 2021-12-21 13:38 developer ~0008964	Hi jacky, I got 1 box from customer. It is reboot repeatedly with below log. It is happen in real filed so we have to support it in short time. ================================== init cpupll 800MHz init mempll 2400MHz init 3GB DDR, lib version r63074 hw_init done custom table done asking BCM to load miniloader. init syspll t8sm00:M rHez ease idR KE0xKd_9IcD9 (2b0y7t, ec[0o:m7m]i)t t=i m5eca W10ed00 0Ap0r0 020403 1532: UL:T5 5( b20y1t9e [-0:077]0)0 1 = 4b949fa6d02151dc Chip version = 0xa0 Check bootmode = 0x0 ! Image Load done with bootmode=0x0 ! NOTICE: bl31v13692d41f6 starting...5200a7914a95 BOOT_REASON: reboot PMIC: SY8824B selected for board bg5ct_ffv ! set Vcpu from 1050000uv to 1000000uv set Vcore from 1050000uv to 1100000uv i2c status:0x00000550, send_num:0 (expect:0), recv_num:0 (expect:2) tx abort source:0x1 bit0:(7-bit addressing) address not ACKed by any slave set_AMP_power, i2c_master_write_and_read fail. sub_cmd_id: 4, param_len: 0x2c, 0x0, 0x4 check keys ret=1 ####register ta LOGO register_ta:tz comm rsp done! 80000000, 0 ####register ta CYPT register_ta:tz comm rsp done! 80000000, 0 [fastlogo] flash_ts_get DisplayPrimaryHDMIBitDepth=2 TAMgr_CreateInstance:tz comm rsp done! 80000000, 0, 6 invoke command 0x00000000 TASysCmd_OpenSession:tz comm rsp done! 80000000, 0, 6000100 MV_VPPOBJ_EnableAutoPush:12327: Autopush enable : 0 FixColor flag : 1/0, FixColor:1 MV_VPP_FL_Init:891: enableFlushCache=0, iHDMIEnable=0, enableAutoPush=1 [fastlogo] value of frame number=3 [fastlogo] got fit size logo: 1280x720(index:1, num:3) [fastlogo] showlogo_start: logo_start=4148224, logo_size=1843200, logo_width=1280, logo_height=720, logo_stride=2560 MV_VPPOBJ_FL_DisplayFrame:4193: enableInterrupt=0 load boot normal image. BOOT IMAGE HEADER VERSION: 2 ! verify slot done TAMgr_CreateInstance:tz comm rsp done! 80000000, 0, 7 invoke command 0x00000000 TASysCmd_OpenSession:tz comm rsp done! 80000000, 0, 7000200 DRM_SetVersionsV4: Bootpatchlevel: 0x30188 DRM_SetVersionsV4: Vendorpatchlevel: 0x30188 DRM_SetVersionsV4: Systempatchlevel: 0x30188 INFO: set eMMC dll_phsel0 to 0x2a for kernel. size of allocated memory is not correct! can't find the allocated memory [ 0] Start kernel at 0x05880000 [ 2055]I(SM): *****IR ready**** [ 0.910186] ioremap success: vir_addr: (ptrval), size: 0x3000000, phy_addr: (ptrval)! [ 0.920404] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered [ 0.996751] chipidea-usb2 f7ed0000.usb: set of GPIOH_5(496) as USB Hub reset [ 1.004095] chipidea-usb2 f7ed0000.usb: ok to acquire gpio and set output [ 1.012632] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered [ 1.021247] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered [ 1.085109] device-mapper: verity: 253:2: metadata block 210215 is corrupted [ 1.145730] reboot: Restarting system with command 'dm-verity device corrupted' [ 2367]I(SM): %%%%SM:Msg reset system%%%% [ 2371]I(SM): ****enter flow ---Sysreset to Active--- [ 2377]I(SM): soc_assert done [ 2380]I(SM): peripheral_assert done [ 2388]I(SM): mv_sm_power_socpoweroff done [ 2696]I(SM): mv_sm_power_socpoweron done [ 2752]I(SM): peripheral_assert done [ 2756]I(SM): soc_assert done [ 2852]I(SM): peripheral_deassert done [ 2856]I(SM): soc_deassert done ========================================= Thanks.

(ALTech) Sangmin Choi 2021-12-22 10:01 developer ~0008981	Hi, Bcan, Can I know current status of this job? SKB manager wants to know this job was done or not. If it was not complete, please let me know the schedule to complete. Thank you.

(SW) Bcan Yeh 2021-12-22 11:34 developer ~0009008	Hi Sangmin, We need to check if we can detect this situation and go to recovery mode first. Due date: 12/30

(ALTech) Sangmin Choi 2021-12-22 12:11 developer ~0009010	Hi, Bcan, Thank you for update. FYI, other vendors who uses same chipset already fixed it, so I guess you can detect this situation. SKB Jira : https://jira.skbroadband.com/browse/BTVD-18589 Thank you.

(ALTech) Sangmin Choi 2021-12-30 10:43 developer ~0009175	Hi, Bcan, Could you please update current status? Thank you.

(SW) Bcan Yeh 2021-12-30 16:43 developer ~0009179	Hi Sangmin, We need more time to fix this problem. In AI2MAX, there is no "dm-verity device corrupted" message showed. Instead, it shows ---------------------------------------------------------------------------------- EXT4-fs (dm-2): VFS: Can't find ext4 filesystem fts: failed to open /dev/block/by-name/fts Failed to open fts device. ---------------------------------------f------------------------------------------- It looks like that fts device can't be mounted successfully. In order to restart to recovery mode, it need to set bootmode as recovery. However, the function set_bootmode writes data into fts device. Because fts device can't be mounted, it fails to do set_bootmode.

(ALTech) Sangmin Choi 2022-01-03 07:58 developer ~0009184	Dear Bcan, As I attached the log message, "dm-verity device corrupted" issue is easy to reproduce. Could you please check it more? Did you use correct firmware version when you test it? This issue will be reproduced when you upgrade the firmware from 19.531.18 to 19.531.20. Thank you.

(SW) Bcan Yeh 2022-01-03 11:36 developer ~0009189	Hi Sangmin, I tested this issue with 532r17 SD build. By testing with 532r17 SU build, it shows "dm-verity device corrupted" message. I will check this issue again and thanks for your reminding.

(ALTech) Sangmin Choi 2022-01-03 12:27 developer ~0009191	Hi, Bcan, Thank you for update. I'm sorry to push you but the due date was 12/30. SKB manager will ask us in a few days. I think this issue should be fixed in this week, of course, without any side effect. Please understand our circumstance. Thank you and happy new year.

(ALTech) Sangmin Choi 2022-01-10 10:35 developer ~0009310	Hi, Bcan, Could you please update current status? Thank you.

(SW) Bcan Yeh 2022-01-10 18:59 developer ~0009315	Hi Sangmin, The workaround for powering off during OTA is already committed. Please check it.

(ALTech) Sangmin Choi 2022-01-11 13:22 developer ~0009318 Last edited: 2022-01-11 13:22	Hi, Bcan, Request #1 There was a daily build image which is included your modifications.(20220111 daily build) But, I can't test using same image because there is no error when I plug out during upgrade. So, could you please send me an upgrade image? Please do the clean build for upgrade test. Request #2 I have no image to test, so I tested like followings. STB(19.532.10) -> Upgrade to "20220111 daily build" and plug out when the upgrade progress is 34% STB(20220111 daily build) -> Upgrade to 19.532.10 and plug out when the upgrade progress is 34% I can see the following error messages and the STB enter to the fastboot mode. [ 3.151876] usb 2-1: New USB device found, idVendor=0781, idProduct=5591 [ 3.158801] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 3.166190] usb 2-1: Product: SanDisk 3.2Gen1 [ 3.170781] usb 2-1: Manufacturer: USB [ 3.174768] usb 2-1: SerialNumber: 0101659408e68318f395c2e219e6c85eb53a2f979f42c00294e89848c5b12b0da7a900000000000000000000b83639460084310091558107c7296af8 [ 3.230886] reboot: Restarting system with command 'bootloader' [ 3.237034] reboot cmd is bootloader@11 [ 4437]I(SM): %%%%SM:Msg reset system%%%% [ 4441]I(SM): ****enter flow ---Sysreset to Active--- [ 4447]I(SM): soc_assert done [ 4450]I(SM): peripheral_assert done [ 4458]I(SM): mv_sm_power_socpoweroff done [ 4766]I(SM): mv_sm_power_socpoweron done Do you know why? I attached the log file, could you please check it? Thank you. fastboot.png (13,044,921 bytes) fastboot_log.txt (23,873 bytes) init cpupll 800MHz init mempll 2400MHz init 3GB DDR, lib version r63074 hw_init done custom table done asking BCM to load miniloader. init syspll 800MHz RKEK_ID (byte[0ts:7m]: ) re= le5acase1 00id0 000x00d90c029240e U cLoT mm(ibyt tte[im0e: 7W])ed Ap r =2 44 b1283:11c87a:e554a 82070119 c p Ch0i0 - version = 0xa0 Check bootmode = 0x0 ! Image Load done with bootmode=0x0 ! NOTICE: bl31v13692d41f6 starting...5200a7914a95 PMIC: SY8824B selected for board bg5ct_ffv ! set Vcpu from 1050000uv to 1000000uv set Vcore from 1050000uv to 1100000uv i2c status:0x00000550, send_num:0 (expect:0), recv_num:0 (expect:2) tx abort source:0x1 bit0:(7-bit addressing) address not ACKed by any slave set_AMP_power, i2c_master_write_and_read fail. sub_cmd_id: 4, param_len: 0x2c, 0x0, 0x4 check keys ret=1 ####register ta LOGO register_ta:tz comm rsp done! 80000000, 0 ####register ta CYPT register_ta:tz comm rsp done! 80000000, 0 [fastlogo] flash_ts_get DisplayPrimaryHDMIBitDepth=2 TAMgr_CreateInstance:tz comm rsp done! 80000000, 0, 6 invoke command 0x00000000 TASysCmd_OpenSession:tz comm rsp done! 80000000, 0, 6000100 MV_VPPOBJ_EnableAutoPush:12327: Autopush enable : 0 FixColor flag : 1/0, FixColor:1 MV_VPP_FL_Init:891: enableFlushCache=0, iHDMIEnable=0, enableAutoPush=1 [fastlogo] value of frame number=3 [fastlogo] got fit size logo: 1280x720(index:1, num:3) [fastlogo] showlogo_start: logo_start=4148224, logo_size=1843200, logo_width=1280, logo_height=720, logo_stride=2560 MV_VPPOBJ_FL_DisplayFrame:4193: enableInterrupt=0 load boot normal image. BOOT IMAGE HEADER VERSION: 2 ! verify slot done TAMgr_CreateInstance:tz comm rsp done! 80000000, 0, 7 invoke command 0x00000000 TASysCmd_OpenSession:tz comm rsp done! 80000000, 0, 7000200 DRM_SetVersionsV4: Bootpatchlevel: 0x30188 DRM_SetVersionsV4: Vendorpatchlevel: 0x30188 DRM_SetVersionsV4: Systempatchlevel: 0x30188 INFO: set eMMC dll_phsel0 to 0x2a for kernel. size of allocated memory is not correct! can't find the allocated memory [ 0] Start kernel at 0x05880000 [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.9.178 (jim@010870-build58-workstation) (Android (5407736 based on r353983b) clang version 9.0.2 (https://android.googlesource.com/toolchain/clang 649b4adfc45b26ae937fe597ef2 [ 0.000000] Boot CPU: AArch64 Processor [410fd034] [ 0.000000] MAX DMA PHYS limited to 2048MB [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv1.0 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: Trusted OS migration not required [ 0.000000] psci: SMC Calling Convention v1.0 [ 0.000000] percpu: Embedded 22 pages/cpu s52288 r8192 d29632 u90112 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: enabling workaround for ARM erratum 845719 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 638064 [ 0.000000] Kernel command line: androidboot.boot_devices=soc/f7aa0000.sdhci console=ttyS0,115200 init=/init root=/dev/ram0 androidboot.console=ttyS0 androidboot.hardware=sequoia androidboot.wificounti [ 0.000000] log_buf_len individual max cpu contribution: 4096 bytes [ 0.000000] log_buf_len total cpu_extra contributions: 12288 bytes [ 0.000000] log_buf_len min size: 16384 bytes [ 0.000000] log_buf_len: 32768 bytes [ 0.000000] early log buf free: 13640(83%) [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes) [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.000000] software IO TLB: mapped [mem 0x45bff000-0x49bff000] (64MB) [ 0.000000] Memory: 2026916K/2592768K available (9662K kernel code, 1070K rwdata, 3316K rodata, 2432K init, 482K bss, 565852K reserved, 0K cma-reserved) [ 0.000000] Virtual kernel memory layout: [ 0.000000] modules : 0xffffff8000000000 - 0xffffff8008000000 ( 128 MB) [ 0.000000] vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000 ( 250 GB) [ 0.000000] .text : 0x (ptrval) - 0x (ptrval) ( 9664 KB) [ 0.000000] .rodata : 0x (ptrval) - 0x (ptrval) ( 3392 KB) [ 0.000000] .init : 0x (ptrval) - 0x (ptrval) ( 2432 KB) [ 0.000000] .data : 0x (ptrval) - 0x (ptrval) ( 1070 KB) [ 0.000000] .bss : 0x (ptrval) - 0x (ptrval) ( 483 KB) [ 0.000000] fixed : 0xffffffbefe7fd000 - 0xffffffbefec00000 ( 4108 KB) [ 0.000000] PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000 ( 16 MB) [ 0.000000] vmemmap : 0xffffffbf00000000 - 0xffffffc000000000 ( 4 GB maximum) [ 0.000000] 0xffffffbf00160000 - 0xffffffbf03000000 ( 46 MB actual) [ 0.000000] memory : 0xffffffc005800000 - 0xffffffc0c0000000 ( 2984 MB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 64. [ 0.000000] NR_IRQS:64 nr_irqs:64 0 [ 0.000000] GIC: Using split EOI/Deactivate mode [ 0.000000] arm_arch_timer: Architected cp15 timer(s) running at 25.00MHz (phys). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns [ 0.000005] sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns [ 0.000538] Console: colour dummy device 80x25 [ 0.000567] Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=250000) [ 0.000580] pid_max: default: 32768 minimum: 301 [ 0.000655] Security Framework initialized [ 0.000663] SELinux: Initializing. [ 0.000721] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes) [ 0.000730] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes) [ 0.001649] sched-energy: CPU device node has no sched-energy-costs [ 0.001658] Invalid sched_group_energy for CPU0 [ 0.001665] CPU0: update cpu_capacity 1024 [ 0.029952] ASID allocator initialised with 65536 entries [ 0.200223] Detected VIPT I-cache on CPU1 [ 0.200262] Invalid sched_group_energy for CPU1 [ 0.200265] CPU1: update cpu_capacity 1024 [ 0.200268] CPU1: Booted secondary processor [410fd034] [ 0.280293] Detected VIPT I-cache on CPU2 [ 0.280320] Invalid sched_group_energy for CPU2 [ 0.280323] CPU2: update cpu_capacity 1024 [ 0.280326] CPU2: Booted secondary processor [410fd034] [ 0.360376] Detected VIPT I-cache on CPU3 [ 0.360403] Invalid sched_group_energy for CPU3 [ 0.360406] CPU3: update cpu_capacity 1024 [ 0.360409] CPU3: Booted secondary processor [410fd034] [ 0.360487] Brought up 4 CPUs [ 0.360526] SMP: Total of 4 processors activated. [ 0.360534] CPU features: detected feature: 32-bit EL0 Support [ 0.360546] CPU: All CPU(s) started at EL2 [ 0.360579] alternatives: patching kernel code [ 0.360729] Invalid sched_group_energy for Cluster3 [ 0.360737] CPU3: update max cpu_capacity 1024 [ 0.360743] Invalid sched_group_energy for Cluster2 [ 0.360749] Invalid sched_group_energy for Cluster1 [ 0.360754] Invalid sched_group_energy for Cluster0 [ 0.370315] CPU1: update max cpu_capacity 1024 [ 0.370386] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.370402] futex hash table entries: 1024 (order: 4, 65536 bytes) [ 0.370559] pinctrl core: initialized pinctrl subsystem [ 0.371256] NET: Registered protocol family 16 [ 0.400382] cpuidle: using governor menu [ 0.400647] vdso: 2 pages (1 code @ (ptrval), 1 data @ (ptrval)) [ 0.400657] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.400849] DMA: preallocated 256 KiB pool for atomic allocations [ 0.450993] SCSI subsystem initialized [ 0.451190] usbcore: registered new interface driver usbfs [ 0.451244] usbcore: registered new interface driver hub [ 0.451303] usbcore: registered new device driver usb [ 0.452352] i2c_designware f7fcb000.i2c: could not find pctldev for node /soc/pinctrl@fe2200/tw2-pmux, deferring probe [ 0.452384] i2c_designware f7fcc000.i2c: could not find pctldev for node /soc/pinctrl@fe2200/tw3-pmux, deferring probe [ 0.452421] Linux video capture interface: v2.00 [ 0.452636] Marvell Berlin ION information [ 0.452645] heap_id[0] memory start[49c00000] len[1a000000] name[NonSecure] type[2] attri[00000f36] [ 0.452654] heap_id[1] memory start[63c00000] len[01400000] name[NonSecure-NC] type[5] attri[00000f3a] [ 0.452662] heap_id[2] memory start[65000000] len[1b000000] name[Secure] type[5] attri[00000f3d] [ 0.452670] heap_id[3] memory start[00000000] len[00000000] name[system-ion] type[0] attri[00000000] [ 0.452676] berlin found 1 secure heap [ 0.452682] berlin found secure heap[0] head_id[2] [ 0.544329] Advanced Linux Sound Architecture Driver Initialized. [ 0.544828] Bluetooth: Core ver 2.22 [ 0.544862] NET: Registered protocol family 31 [ 0.544868] Bluetooth: HCI device and connection manager initialized [ 0.544880] Bluetooth: HCI socket layer initialized [ 0.544890] Bluetooth: L2CAP socket layer initialized [ 0.544918] Bluetooth: SCO socket layer initialized [ 0.550879] clocksource: Switched to clocksource arch_sys_counter [ 0.606692] VFS: Disk quotas dquot_6.6.0 [ 0.606756] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.611953] NET: Registered protocol family 2 [ 0.650980] TCP established hash table entries: 32768 (order: 6, 262144 bytes) [ 0.651288] TCP bind hash table entries: 32768 (order: 7, 524288 bytes) [ 0.651935] TCP: Hash tables configured (established 32768 bind 32768) [ 0.652006] UDP hash table entries: 2048 (order: 4, 65536 bytes) [ 0.652119] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes) [ 0.652344] NET: Registered protocol family 1 [ 0.652613] Trying to unpack rootfs image as initramfs... [ 0.687686] Freeing initrd memory: 752K [ 0.688230] hw perfevents: enabled with armv8_cortex_a53 PMU driver, 7 counters available [ 0.690469] audit: initializing netlink subsys (disabled) [ 0.690528] audit: type=2000 audit(1234567890.680:1): initialized [ 0.691184] workingset: timestamp_bits=45 max_order=19 bucket_order=0 [ 0.698502] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.698685] Registering sdcardfs 0.1 [ 0.698930] ntfs: driver 2.1.32 [Flags: R/O]. [ 0.699133] fuse init (API version 7.26) [ 0.704070] io scheduler noop registered [ 0.704079] io scheduler deadline registered (default) [ 0.704195] io scheduler cfq registered [ 0.711133] GPIO line 359 (phy0_reset) hogged as output/high [ 0.729337] marvell,berlin-sm f7f80000.sm: rtc core: registered bsm_rtc as rtc0 [ 0.730097] tz_logger_dev: created 128K log 'tzlogger' [ 0.730109] MV_VPP_InitMode:Normal mode, ta_flag:0 [ 0.730421] Serial: 8250/16550 driver, 2 ports, IRQ sharing disabled [ 0.731176] console [ttyS0] disabled [ 0.731224] f7fcd000.uart: ttyS0 at MMIO 0xf7fcd000 (irq = 32, base_baud = 1562500) is a 16550A [ 1.663584] console [ttyS0] enabled [ 1.667641] f7fce000.uart: ttyS1 at MMIO 0xf7fce000 (irq = 33, base_baud = 1562500) is a 16550A [ 1.676879] [drm] Initialized [ 1.688808] brd: module loaded [ 1.701322] loop: module loaded [ 1.705071] zram: Added device: zram0 [ 1.709326] libphy: Fixed MDIO Bus: probed [ 1.713989] tun: Universal TUN/TAP device driver, 1.6 [ 1.719206] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com> [ 1.727293] Network controller revision: 0010 [ 1.732995] mvneta f7b60000.ethernet eth0: Using random mac address 4a:b0:bb:4a:c9:16 [ 1.741310] libphy: orion_mdio_bus: probed [ 1.746119] fxc ** rtl8211f_probe enter [ 1.750319] PPP generic driver version 2.4.2 [ 1.754835] PPP BSD Compression module registered [ 1.759693] PPP Deflate Compression module registered [ 1.764944] PPP MPPE Compression module registered [ 1.769892] NET: Registered protocol family 24 [ 1.774491] PPTP driver version 0.8.5 [ 1.778597] usbcore: registered new interface driver r8152 [ 1.784325] usbcore: registered new interface driver asix [ 1.789947] usbcore: registered new interface driver ax88179_178a [ 1.796293] usbcore: registered new interface driver cdc_ether [ 1.802365] usbcore: registered new interface driver net1080 [ 1.808243] usbcore: registered new interface driver cdc_subset [ 1.814423] usbcore: registered new interface driver cdc_ncm [ 1.820267] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 1.827432] usbcore: registered new interface driver usb-storage [ 1.833831] ci_hdrc_usb2_probe---------------61 probe start [ 1.840703] input: Infra-Red as /devices/virtual/input/input0 [ 3[04 3] I (S1M).: 8468*68I]R reaudysbcore : registered new interface driver xpad [ 1.856153] usbcore: registered new interface driver usb_acecad [ 1.862316] usbcore: registered new interface driver aiptek [ 1.868104] usbcore: registered new interface driver gtco [ 1.873726] usbcore: registered new interface driver hanwang [ 1.879604] usbcore: registered new interface driver kbtab [ 1.885346] i2c /dev entries driver [ 1.889188] usbcore: registered new interface driver uvcvideo [ 1.895133] USB Video Class driver (1.1.1) [ 1.899363] gspca_main: v2.14.0 registered [ 1.904038] device-mapper: uevent: version 1.0.3 [ 1.908995] device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised: dm-devel@redhat.com [ 1.917814] device-mapper: verity-avb: AVB error handler initialized with vbmeta device: PARTUUID=d4981a2b-0478-544e-9607-7fd3c651068d [ 1.930304] rtk_btusb: RTKBT_RELEASE_NAME: 20200519_BT_ANDROID_10.0 [ 1.936786] rtk_btusb: Realtek Bluetooth USB driver module init, version 5.2.1 [ 1.944250] rtk_btusb: Register usb char device interface for BT driver [ 1.951219] usbcore: registered new interface driver rtk_btusb [ 1.957908] sdhci: Secure Digital Host Controller Interface driver [ 1.964303] sdhci: Copyright(c) Pierre Ossman [ 1.968904] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.030917] mmc0: SDHCI controller on f7aa0000.sdhci [f7aa0000.sdhci] using ADMA [ 2.040804] hidraw: raw HID events driver (C) Jiri Kosina [ 2.049794] usbcore: registered new interface driver usbhid [ 2.055597] usbhid: USB HID core driver [ 2.061797] ashmem: initialized [ 2.066591] usbcore: registered new interface driver snd-usb-audio [ 2.074466] ioremap success: vir_addr: (ptrval), size: 0x3000000, phy_addr: (ptrval)! [ 2.084680] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered [ 2.093071] register card failed (-517) [ 2.097129] snd_bt_init [ 2.099742] u32 classifier [ 2.102592] Actions configured [ 2.106131] Netfilter messages via NETLINK v0.30. [ 2.111259] nf_conntrack version 0.5.0 (16384 buckets, 65536 max) [ 2.117727] ctnetlink v0.93: registering with nfnetlink. [ 2.123511] xt_time: kernel timezone is -0000 [ 2.128122] gre: GRE over IPv4 demultiplexor driver [ 2.133212] IPv4 over IPsec tunneling driver [ 2.138208] ip_tables: (C) 2000-2006 Netfilter Core Team [ 2.143934] arp_tables: arp_tables: (C) 2002 David S. Miller [ 2.149843] Initializing XFRM netlink socket [ 2.154360] IPsec XFRM device driver [ 2.158535] NET: Registered protocol family 10 [ 2.164332] mip6: Mobile IPv6 [ 2.165287] mmc0: new HS400 MMC card at address 0001 [ 2.165817] mmcblk0: mmc0:0001 8GTF4R 7.28 GiB [ 2.176228] mmcblk0boot0: mmc0:0001 8GTF4R partition 1 4.00 MiB [ 2.176641] mmcblk0boot1: mmc0:0001 8GTF4R partition 2 4.00 MiB [ 2.186960] mmcblk0rpmb: mmc0:0001 8GTF4R partition 3 512 KiB [ 2.189325] mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 p9 p10 p11 p12 p13 p14 p15 p16 p17 p18 p19 p20 p21 p22 p23 [ 2.205221] ip6_tables: (C) 2000-2006 Netfilter Core Team [ 2.211533] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 2.218648] NET: Registered protocol family 17 [ 2.223274] NET: Registered protocol family 15 [ 2.227884] l2tp_core: L2TP core driver, V2.0 [ 2.232404] l2tp_ppp: PPPoL2TP kernel driver, V2.0 [ 2.237674] Registered cp15_barrier emulation handler [ 2.242952] Registered setend emulation handler [ 2.248010] registered taskstats version 1 [ 2.253969] Key type encrypted registered [ 2.258121] FB: VPP init mode status failed! [ 2.274268] xhci-hcd f7a20000.xhci: xHCI Host Controller [ 2.279780] xhci-hcd f7a20000.xhci: new USB bus registered, assigned bus number 1 [ 2.287662] xhci-hcd f7a20000.xhci: hcc params 0x0a000998 hci version 0x100 quirks 0x00010010 [ 2.296520] xhci-hcd f7a20000.xhci: irq 21, io mem 0xf7a20000 [ 2.302621] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 [ 2.309634] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.317111] usb usb1: Product: xHCI Host Controller [ 2.322163] usb usb1: Manufacturer: Linux 4.9.178 xhci-hcd [ 2.327830] usb usb1: SerialNumber: f7a20000.xhci [ 2.333095] hub 1-0:1.0: USB hub found [ 2.337000] hub 1-0:1.0: 1 port detected [ 2.341337] xhci-hcd f7a20000.xhci: xHCI Host Controller [ 2.346834] xhci-hcd f7a20000.xhci: new USB bus registered, assigned bus number 2 [ 2.354640] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 2.363112] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003 [ 2.370125] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.377595] usb usb2: Product: xHCI Host Controller [ 2.382645] usb usb2: Manufacturer: Linux 4.9.178 xhci-hcd [ 2.388313] usb usb2: SerialNumber: f7a20000.xhci [ 2.393548] hub 2-0:1.0: USB hub found [ 2.397446] hub 2-0:1.0: 1 port detected [ 2.401899] ci_hdrc_usb2_probe---------------61 probe start [ 2.410854] ci_hdrc ci_hdrc.0: EHCI Host Controller [ 2.415918] ci_hdrc ci_hdrc.0: new USB bus registered, assigned bus number 3 [ 2.450948] ci_hdrc ci_hdrc.0: USB 2.0 started, EHCI 1.00 [ 2.456657] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002 [ 2.463686] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 2.471156] usb usb3: Product: EHCI Host Controller [ 2.476196] usb usb3: Manufacturer: Linux 4.9.178 ehci_hcd [ 2.481872] usb usb3: SerialNumber: ci_hdrc.0 [ 2.486743] hub 3-0:1.0: USB hub found [ 2.490641] hub 3-0:1.0: 1 port detected [ 2.495086] chipidea-usb2 f7ed0000.usb: set of GPIOH_5(496) as USB Hub reset [ 2.502386] chipidea-usb2 f7ed0000.usb: ok to acquire gpio and set output [ 2.510657] syna-berlin-asoc soc:berlin-asoc: ASoC: CODEC DAI cx2081x-aif not registered [ 2.519053] register card failed (-517) [ 2.523451] ALSA device list: [ 2.526515] No soundcards found. [ 2.530140] ttyS0 - failed to request DMA [ 2.534857] Freeing unused kernel memory: 2432K [ 2.543646] init: init first stage started! [ 2.548256] init: [libfs_mgr]ReadFstabFromDt(): failed to read fstab from dt [ 2.556404] init: Using Android DT directory /proc/device-tree/firmware/android/ [ 2.587435] init: [libfs_mgr]Created logical partition product on device /dev/block/dm-0 [ 2.596448] init: [libfs_mgr]Created logical partition vendor on device /dev/block/dm-1 [ 2.605345] init: [libfs_mgr]Created logical partition system on device /dev/block/dm-2 [ 2.614036] init: [libfs_mgr]superblock s_max_mnt_count:10,/dev/block/by-name/metadata [ 2.622263] init: [libfs_mgr]Filesystem on /dev/block/by-name/metadata was not cleanly shutdown; state flags: 0x1, incompat feature flags: 0x46 [ 2.636103] init: [libfs_mgr]check_fs(): mount(/dev/block/by-name/metadata,/metadata,ext4)=-1: No such file or directory [ 2.647384] init: [libfs_mgr]Not running /system/bin/e2fsck on /dev/block/mmcblk0p20 (executable not in system image) [ 2.662822] EXT4-fs (mmcblk0p20): warning: maximal mount count reached, running e2fsck is recommended [ 2.673080] EXT4-fs (mmcblk0p20): recovery complete [ 2.678499] EXT4-fs (mmcblk0p20): mounted filesystem with ordered data mode. Opts: discard [ 2.700282] EXT4-fs (dm-2): VFS: Can't find ext4 filesystem [ 2.728337] fts: fts partition is on /dev/block/by-name/fts, 0x400000 bytes [ 2.735589] fts: chunk: 0x4000 [ 2.754970] random: fast init done [ 2.763501] fts: flash_ts_scan skiped 1 blocks [ 2.768091] fts: v310 loaded from 0x000d4000 [ 2.772525] fts: write off=0x000d8000 size=0x200 [ 2.777922] fts: record v311 commited @ 0x000d8000 [ 2.810891] usb 1-1: new high-speed USB device number 2 using xhci-hcd [ 2.982240] usb 1-1: New USB device found, idVendor=05e3, idProduct=0608 [ 2.989158] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 [ 2.996538] usb 1-1: Product: USB2.0 Hub [ 3.120956] usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd [ 3.151876] usb 2-1: New USB device found, idVendor=0781, idProduct=5591 [ 3.158801] usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 3.166190] usb 2-1: Product: SanDisk 3.2Gen1 [ 3.170781] usb 2-1: Manufacturer: USB [ 3.174768] usb 2-1: SerialNumber: 0101659408e68318f395c2e219e6c85eb53a2f979f42c00294e89848c5b12b0da7a900000000000000000000b83639460084310091558107c7296af8 [ 3.230886] reboot: Restarting system with command 'bootloader' [ 3.237034] reboot cmd is bootloader@11 [ 4437]I(SM): %%%%SM:Msg reset system%%%% [ 4441]I(SM): ****enter flow ---Sysreset to Active--- [ 4447]I(SM): soc_assert done [ 4450]I(SM): peripheral_assert done [ 4458]I(SM): mv_sm_power_socpoweroff done [ 4766]I(SM): mv_sm_power_socpoweron done [ 4822]I(SM): peripheral_assert done [ 4826]I(SM): soc_assert done [ 4922]I(SM): peripheral_deassert done [ 4925]I(SM): soc_deassert done init cpupll 800MHz init mempll 2400MHz init 3GB DDR, lib version r63074 hw_init done custom table done asking BCM to load miniloader. init syspll 800MHz RKEKt_IsDm :( bryetleea[s0e:7 ]i)d =0 x5dc9ac912000070,0 0c0om0m0i2t4e 8UeL T We(db yAtper[ 0:247 ]1)3 : 1 7 : 5=5 4b2028191c 8-a0e7040a ti 701c Chip version = 0xa0 Check bootmode = 0x5 ! Image Load done with bootmode=0x5 ! [ 8886]I(SM): SM: received MV_SM_WD_FASTBOOT Msg NOTICE: bl31v13692d41f6 starting...5200a7914a95 U-Boot 2016.11 (Jan 11 2022 - 01:43:49 +0800) Model: MARVELL BG5CT STB FASTBOOT DRAM: 1.5 GiB MMC: SDHCI: switch host to 1.8V ! sdhci@aa0000: 0 Using default environment In: uart@d000 Out: uart@d000 Err: uart@d000 fts: v312 loaded from 0x000dc000 Serial#: SKBFXYVH0JF6 Product: bg5ct(default) find a valid node 1 find a valid node 1 find a valid node 2 find a valid node 3 find a valid node 4 ####find ta LOGO 4f474f4c find a valid node 5 register_ta:tz comm rsp done! 80000000, 0 find a valid node 6 TAMgr_CreateInstance:tz comm rsp done! 80000000, 0, 6 invoke command 0x00000000 TASysCmd_OpenSession:tz comm rsp done! 80000000, 0, 6000100 find a valid node 2 MV_VPPOBJ_EnableAutoPush:12327: Autopush enable : 0 FixColor flag : 1/0, FixColor:1 MV_VPP_FL_Init:891: enableFlushCache=0, iHDMIEnable=0, enableAutoPush=1 find a valid node 7 find a valid node 8 find a valid node 9 find a valid node 10 MV_VPP_Init MV_VPPOBJ_FL_DisplayFrame:4193: enableInterrupt=0 Fastboot Version: 0.4 U-Boot Version: U-Boot 2016.11 (Jan 11 2022 - 01:43:49 +0800) enter fastboot mode fastboot_log.txt (23,873 bytes)

(SW) Bcan Yeh 2022-01-11 19:10 developer ~0009326	Hi Sangmin, 1. This workaround is only workable in SU build for end users. 2. For SD build, it would reboot repeatedly before and it will go to fastboot mode now. Due to SD build is only available for engineers and QA. It is suggested to press vol- and channel- to go to recovery mode and recover the device. 3. Please find image here. https://drive.google.com/drive/folders/19auum7keW_TNeC2H-yMcfuCgnvDRtKGl?usp=sharing You can test in this way: STB(20220111_SU_v532r17_ota1) -> Upgrade to "20220111 daily build" and plug out when the upgrade progress is 34%

(ALTech) Sangmin Choi 2022-01-12 10:34 developer ~0009327	Hi, Bcan, I checked this issue was fixed. Thank you for support.

Date Modified	Username	Field	Change
2021-12-16 14:31	(ALTech) Sangmin Choi	New Issue
2021-12-16 14:31	(ALTech) Sangmin Choi	Status	new => assigned
2021-12-16 14:31	(ALTech) Sangmin Choi	Assigned To	=> (SW) Jacky Chiang
2021-12-16 14:35	(ALTech) Sangmin Choi	Note Added: 0008932
2021-12-16 14:35	(ALTech) Sangmin Choi	Issue Monitored: (ALTech) Sangmin Choi
2021-12-20 09:13	(SW) Jacky Chiang	Note Added: 0008951
2021-12-20 09:13	(SW) Jacky Chiang	Issue Monitored: (SW) Bcan Yeh
2021-12-20 09:54	(SW) Jacky Chiang	Assigned To	(SW) Jacky Chiang => (SW) Bcan Yeh
2021-12-20 09:58	(ALTech) Sangmin Choi	Note Added: 0008955
2021-12-21 13:38	(ALTech) Wooshin Kang	Note Added: 0008964
2021-12-22 10:01	(ALTech) Sangmin Choi	Note Added: 0008981
2021-12-22 11:34	(SW) Bcan Yeh	Note Added: 0009008
2021-12-22 12:11	(ALTech) Sangmin Choi	Note Added: 0009010
2021-12-24 16:40	~~(SW) River Wong~~	Severity	s2-severe => s6-feature
2021-12-27 12:25	(ALTech) Sangmin Choi	Due Date	2021-12-20 14:51 => 2021-12-30 13:00
2021-12-30 10:43	(ALTech) Sangmin Choi	Note Added: 0009175
2021-12-30 16:43	(SW) Bcan Yeh	Note Added: 0009179
2021-12-30 16:44	(SW) Bcan Yeh	Assigned To	(SW) Bcan Yeh => (ALTech) Sangmin Choi
2021-12-30 16:44	(SW) Bcan Yeh	Assigned To	(ALTech) Sangmin Choi => (SW) Bcan Yeh
2022-01-03 07:58	(ALTech) Sangmin Choi	Note Added: 0009184
2022-01-03 11:36	(SW) Bcan Yeh	Note Added: 0009189
2022-01-03 12:27	(ALTech) Sangmin Choi	Note Added: 0009191
2022-01-10 10:35	(ALTech) Sangmin Choi	Note Added: 0009310
2022-01-10 18:59	(SW) Bcan Yeh	Note Added: 0009315
2022-01-11 13:22	(ALTech) Sangmin Choi	File Added: fastboot.png
2022-01-11 13:22	(ALTech) Sangmin Choi	File Added: fastboot_log.txt
2022-01-11 13:22	(ALTech) Sangmin Choi	Note Added: 0009318
2022-01-11 13:22	(ALTech) Sangmin Choi	Note Edited: 0009318
2022-01-11 19:10	(SW) Bcan Yeh	Note Added: 0009326
2022-01-11 19:10	(SW) Bcan Yeh	Assigned To	(SW) Bcan Yeh => (ALTech) Sangmin Choi
2022-01-12 10:34	(ALTech) Sangmin Choi	Status	assigned => closed
2022-01-12 10:34	(ALTech) Sangmin Choi	Resolution	open => fixed
2022-01-12 10:34	(ALTech) Sangmin Choi	Note Added: 0009327

View Issue Details

Users monitoring this issue

Activities

Issue History